07-01 15:51:42 D/ModuleListener: ModuleListener.testStarted(com.google.android.assistant.v4.tvts.TvtsAssistant4Test#testHeavyDeviceUsage) on SKBFXYWR0LWD
07-01 15:51:42 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:51:42 D/DeviceHostUtils: apiLevel: 29
07-01 15:51:42 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:51:42 D/DeviceHostUtils: apiLevel: 29
07-01 15:51:42 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:51:42 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.katniss
07-01 15:51:42 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.katniss] with timeout: 2m 0s
07-01 15:51:43 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:51:43 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:51:43 D/RunUtil: Running command null without timeout.
07-01 15:51:43 D/AppRunnerUtils: Checking service: com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
07-01 15:51:43 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
07-01 15:51:43 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, dumpsys, activity, service, com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService] with timeout: 2m 0s
07-01 15:51:43 I/AppRunnerUtils: Service PID = 31604
07-01 15:51:43 I/AssistantTestUtils: Waiting for assistant to be ready...
07-01 15:51:43 D/RunUtil: Running command [launch Assistant intent, then check if Assistant is in foreground] without timeout.
07-01 15:51:43 D/AssistantTestUtils: Launching Assistant intent
07-01 15:51:43 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
07-01 15:51:43 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
07-01 15:51:43 D/RunUtil: Running command [check if Assistant is in foreground] without timeout.
07-01 15:51:43 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:51:43 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:51:43 I/DeviceStateMonitor: Waiting 359893 ms for device SKBFXYWR0LWD package manager
07-01 15:51:43 I/NativeDeviceStateMonitor: Waiting 359721 ms for device SKBFXYWR0LWD external store
07-01 15:51:45 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:51:51 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:51:51 D/AssistantTestUtils: Supplier "check if Assistant is in foreground" returned: false
07-01 15:51:51 D/RunUtil: operation failed, waiting for 500 ms
07-01 15:51:51 D/RunUtil: Running command [check if Assistant is in foreground] without timeout.
07-01 15:51:51 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:51:51 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:51:51 I/DeviceStateMonitor: Waiting 359953 ms for device SKBFXYWR0LWD package manager
07-01 15:51:51 I/NativeDeviceStateMonitor: Waiting 359836 ms for device SKBFXYWR0LWD external store
07-01 15:51:53 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
07-01 15:51:53 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.tvsearch.results.activity.SearchResultActivity.
07-01 15:51:53 D/AssistantTestUtils: Supplier "check if Assistant is in foreground" returned: true
07-01 15:51:53 D/AssistantTestUtils: Supplier "launch Assistant intent, then check if Assistant is in foreground" returned: true
07-01 15:51:53 D/AssistantTestUtils: Assistant successfully launched: true
07-01 15:51:53 D/RunUtil: Running command [logcat -d | grep -E 'AssistantWrapper(Impl)?: Assistant is now running.'] without timeout.
07-01 15:51:54 I/AssistantTestUtils: Assistant ready: true
07-01 15:51:54 D/RunUtil: Running command [launch Assistant intent, then check if Assistant is in foreground] without timeout.
07-01 15:51:54 D/AssistantTestUtils: Launching Assistant intent
07-01 15:51:54 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
07-01 15:51:54 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
07-01 15:51:54 D/RunUtil: Running command [check if Assistant is in foreground] without timeout.
07-01 15:51:54 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:51:54 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYWR0LWD boot complete
07-01 15:51:54 I/DeviceStateMonitor: Waiting 359958 ms for device SKBFXYWR0LWD package manager
07-01 15:51:54 I/NativeDeviceStateMonitor: Waiting 359770 ms for device SKBFXYWR0LWD external store
07-01 15:51:55 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
07-01 15:51:56 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.tvsearch.results.activity.SearchResultActivity.
07-01 15:51:56 D/AssistantTestUtils: Supplier "check if Assistant is in foreground" returned: true
07-01 15:51:56 D/AssistantTestUtils: Supplier "launch Assistant intent, then check if Assistant is in foreground" returned: true
07-01 15:51:56 D/AssistantTestUtils: Assistant successfully launched: true
07-01 15:51:56 D/RunUtil: Running command [check if metrics file pattern is present] without timeout.
07-01 15:51:56 I/AssistantTestUtils: Checking if file pattern is present: /storage/emulated/0/Download/katniss-primes*
07-01 15:51:56 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, ls, -t, -d, /storage/emulated/0/Download/katniss-primes*] with timeout: 2m 0s
07-01 15:51:56 D/AssistantTestUtils: Supplier "check if metrics file pattern is present" returned: true
07-01 15:51:56 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, ls, -t, -d, /storage/emulated/0/Download/katniss-primes*] with timeout: 2m 0s
07-01 15:51:56 D/AssistantTestUtils: Session-based metrics File: /storage/emulated/0/Download/katniss-primes-2024-06-30T23_51_54.237.txt
07-01 15:51:56 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/Download/katniss-primes-2024-06-30T23_51_54.237.txt)
07-01 15:51:56 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:51:56 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_17185242548143643700.txt] without timeout.
07-01 15:51:56 D/TvtsBaseAssistantTest: Metrics location: /storage/emulated/0/Download/katniss-primes-2024-06-30T23_51_54.237.txt
07-01 15:51:56 D/TvtsBaseAssistantTest: Option enabling successful!
07-01 15:51:56 I/TvtsBaseAssistantTest: Test: HeavyDeviceUsageTest
07-01 15:51:56 I/MeasurementPrinter: search_to_query_latency	Measurement Status	query_to_result_latency	Measurement Status	query_to_result_no_server_latency	Measurement Status
07-01 15:51:56 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:51:56 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:51:56 I/DeviceStateMonitor: Waiting 359959 ms for device SKBFXYWR0LWD package manager
07-01 15:51:56 I/NativeDeviceStateMonitor: Waiting 359872 ms for device SKBFXYWR0LWD external store
07-01 15:51:57 D/RunUtil: Running command null without timeout.
07-01 15:51:57 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:51:57 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:51:57 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:51:57 D/RunUtil: Running command null without timeout.
07-01 15:51:57 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
07-01 15:51:57 I/KeyCodeUtils: Sending keyevent KEYCODE_HOME - intValue: 3
07-01 15:51:58 D/AppRunnerUtils: [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
07-01 15:51:58 D/RunUtil: operation failed, waiting for 500 ms
07-01 15:51:59 D/RunUtil: Running command null without timeout.
07-01 15:51:59 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:51:59 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:51:59 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:51:59 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:51:59 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:51:59 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:52:00 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_3892245369308121964.txt] without timeout.
07-01 15:52:00 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:52:00 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:52:00 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:52:00 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 15:52:28 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:52:28 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 15:52:28 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 4078.000000
07-01 15:52:28 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 1053.000000
07-01 15:52:28 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 13.000000
07-01 15:52:28 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 844.000000
07-01 15:52:28 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 46.000000
07-01 15:52:28 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 15:52:28 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 8.000000
07-01 15:52:28 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 353.000000
07-01 15:52:28 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3128.000000
07-01 15:52:28 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 49.000000
07-01 15:52:28 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 135.000000
07-01 15:52:28 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 3000.000000
07-01 15:52:28 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 1061.000000
07-01 15:52:28 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 34.000000
07-01 15:52:28 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 15.000000
07-01 15:52:28 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 52.000000
07-01 15:52:28 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 1054.000000
07-01 15:52:28 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 74.000000
07-01 15:52:28 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 135.000000
07-01 15:52:28 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 15:52:28 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 42.000000
07-01 15:52:28 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:52:28 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 15:52:28 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 4078.000000
07-01 15:52:28 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 1053.000000
07-01 15:52:28 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 13.000000
07-01 15:52:28 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 844.000000
07-01 15:52:28 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 46.000000
07-01 15:52:28 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 15:52:28 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 8.000000
07-01 15:52:28 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 353.000000
07-01 15:52:28 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3128.000000
07-01 15:52:28 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 49.000000
07-01 15:52:28 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 135.000000
07-01 15:52:28 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 3000.000000
07-01 15:52:28 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 15:52:28 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 1061.000000
07-01 15:52:28 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:52:28 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 34.000000
07-01 15:52:28 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 15.000000
07-01 15:52:28 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 52.000000
07-01 15:52:28 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 1054.000000
07-01 15:52:28 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 74.000000
07-01 15:52:28 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 135.000000
07-01 15:52:28 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 42.000000
07-01 15:52:28 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:52:28 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 15:52:28 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 4078.000000
07-01 15:52:28 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 1053.000000
07-01 15:52:28 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 13.000000
07-01 15:52:28 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 844.000000
07-01 15:52:28 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 46.000000
07-01 15:52:28 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 15:52:28 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 8.000000
07-01 15:52:28 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 353.000000
07-01 15:52:28 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3128.000000
07-01 15:52:28 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 49.000000
07-01 15:52:28 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 135.000000
07-01 15:52:28 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 3000.000000
07-01 15:52:28 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 1061.000000
07-01 15:52:28 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:52:28 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 34.000000
07-01 15:52:28 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 15.000000
07-01 15:52:28 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 52.000000
07-01 15:52:28 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 1054.000000
07-01 15:52:28 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 74.000000
07-01 15:52:28 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 135.000000
07-01 15:52:28 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 42.000000
07-01 15:52:28 I/MeasurementPrinter:                   135.0	                OK	                 4061.0	                OK	                           1061.0	                OK
07-01 15:52:28 D/RunUtil: Running command null without timeout.
07-01 15:52:28 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:52:29 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:52:29 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:52:29 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:52:29 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:52:29 D/RunUtil: Running command null without timeout.
07-01 15:52:30 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:52:30 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:52:30 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:52:30 D/RunUtil: Running command null without timeout.
07-01 15:52:30 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:52:30 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:52:30 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:52:30 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:52:30 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYWR0LWD package manager
07-01 15:52:30 I/NativeDeviceStateMonitor: Waiting 359903 ms for device SKBFXYWR0LWD external store
07-01 15:52:31 D/RunUtil: Running command null without timeout.
07-01 15:52:31 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:52:31 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:52:31 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:52:31 D/RunUtil: Running command null without timeout.
07-01 15:52:31 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:52:31 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:52:31 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:52:32 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:52:32 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:52:32 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:52:32 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_8866304652385748931.txt] without timeout.
07-01 15:52:32 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:52:32 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:52:32 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:52:32 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 15:52:58 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:52:58 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 15:52:58 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3487.000000
07-01 15:52:58 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 776.000000
07-01 15:52:58 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 5.000000
07-01 15:52:58 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 736.000000
07-01 15:52:58 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 72.000000
07-01 15:52:58 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 12.000000
07-01 15:52:58 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 9.000000
07-01 15:52:58 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 445.000000
07-01 15:52:58 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2773.000000
07-01 15:52:58 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 44.000000
07-01 15:52:58 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 138.000000
07-01 15:52:58 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2708.000000
07-01 15:52:58 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 779.000000
07-01 15:52:58 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 36.000000
07-01 15:52:58 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 8.000000
07-01 15:52:58 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 14.000000
07-01 15:52:58 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 777.000000
07-01 15:52:58 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 73.000000
07-01 15:52:58 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 139.000000
07-01 15:52:58 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 15:52:58 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 15:52:58 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:52:58 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 15:52:58 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3487.000000
07-01 15:52:58 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 776.000000
07-01 15:52:58 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 5.000000
07-01 15:52:58 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 736.000000
07-01 15:52:58 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 72.000000
07-01 15:52:58 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 12.000000
07-01 15:52:58 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 9.000000
07-01 15:52:58 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 445.000000
07-01 15:52:58 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2773.000000
07-01 15:52:58 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 44.000000
07-01 15:52:58 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 138.000000
07-01 15:52:58 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2708.000000
07-01 15:52:58 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 15:52:58 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 779.000000
07-01 15:52:58 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:52:58 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 36.000000
07-01 15:52:58 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 8.000000
07-01 15:52:58 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 14.000000
07-01 15:52:58 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 777.000000
07-01 15:52:58 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 73.000000
07-01 15:52:58 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 139.000000
07-01 15:52:58 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 15:52:58 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:52:58 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 15:52:58 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3487.000000
07-01 15:52:58 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 776.000000
07-01 15:52:58 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 5.000000
07-01 15:52:58 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 736.000000
07-01 15:52:58 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 72.000000
07-01 15:52:58 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 12.000000
07-01 15:52:58 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 9.000000
07-01 15:52:58 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 445.000000
07-01 15:52:58 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2773.000000
07-01 15:52:58 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 44.000000
07-01 15:52:58 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 138.000000
07-01 15:52:58 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2708.000000
07-01 15:52:58 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 779.000000
07-01 15:52:58 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:52:58 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 36.000000
07-01 15:52:58 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 8.000000
07-01 15:52:58 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 14.000000
07-01 15:52:58 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 777.000000
07-01 15:52:58 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 73.000000
07-01 15:52:58 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 139.000000
07-01 15:52:58 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 15:52:58 I/MeasurementPrinter:                   139.0	                OK	                 3487.0	                OK	                            779.0	                OK
07-01 15:52:58 D/RunUtil: Running command null without timeout.
07-01 15:52:58 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:52:59 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:52:59 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:52:59 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:53:00 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:53:00 D/RunUtil: Running command null without timeout.
07-01 15:53:00 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:53:00 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:53:00 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:53:00 D/RunUtil: Running command null without timeout.
07-01 15:53:00 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:53:00 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:53:00 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:53:00 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:53:00 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYWR0LWD package manager
07-01 15:53:00 I/NativeDeviceStateMonitor: Waiting 359876 ms for device SKBFXYWR0LWD external store
07-01 15:53:01 D/RunUtil: Running command null without timeout.
07-01 15:53:01 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:53:01 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:53:01 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:53:01 D/RunUtil: Running command null without timeout.
07-01 15:53:01 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:53:01 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:53:01 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:53:02 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:53:02 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:53:02 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:53:02 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_5005425832037240850.txt] without timeout.
07-01 15:53:02 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:53:02 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:53:02 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:53:02 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 15:53:28 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:53:28 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 15:53:28 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3311.000000
07-01 15:53:28 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 852.000000
07-01 15:53:28 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 15.000000
07-01 15:53:28 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 690.000000
07-01 15:53:28 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 48.000000
07-01 15:53:28 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 10.000000
07-01 15:53:28 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 2.000000
07-01 15:53:28 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 445.000000
07-01 15:53:28 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2483.000000
07-01 15:53:28 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 12.000000
07-01 15:53:28 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 101.000000
07-01 15:53:28 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2404.000000
07-01 15:53:28 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 857.000000
07-01 15:53:28 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 9.000000
07-01 15:53:28 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 3.000000
07-01 15:53:28 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 18.000000
07-01 15:53:28 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 854.000000
07-01 15:53:28 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 77.000000
07-01 15:53:28 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 113.000000
07-01 15:53:28 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 15:53:28 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 30.000000
07-01 15:53:28 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:53:28 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 15:53:28 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3311.000000
07-01 15:53:28 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 852.000000
07-01 15:53:28 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 15.000000
07-01 15:53:28 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 690.000000
07-01 15:53:28 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 48.000000
07-01 15:53:28 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 10.000000
07-01 15:53:28 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 2.000000
07-01 15:53:28 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 445.000000
07-01 15:53:28 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2483.000000
07-01 15:53:28 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 12.000000
07-01 15:53:28 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 101.000000
07-01 15:53:28 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2404.000000
07-01 15:53:28 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 15:53:28 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 857.000000
07-01 15:53:28 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:53:28 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 9.000000
07-01 15:53:28 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 3.000000
07-01 15:53:28 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 18.000000
07-01 15:53:28 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 854.000000
07-01 15:53:28 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 77.000000
07-01 15:53:28 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 113.000000
07-01 15:53:28 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 30.000000
07-01 15:53:28 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:53:28 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 15:53:28 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3311.000000
07-01 15:53:28 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 852.000000
07-01 15:53:28 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 15.000000
07-01 15:53:28 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 690.000000
07-01 15:53:28 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 48.000000
07-01 15:53:28 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 10.000000
07-01 15:53:28 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 2.000000
07-01 15:53:28 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 445.000000
07-01 15:53:28 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2483.000000
07-01 15:53:28 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 12.000000
07-01 15:53:28 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 101.000000
07-01 15:53:28 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2404.000000
07-01 15:53:28 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 857.000000
07-01 15:53:28 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:53:28 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 9.000000
07-01 15:53:28 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 3.000000
07-01 15:53:28 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 18.000000
07-01 15:53:28 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 854.000000
07-01 15:53:28 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 77.000000
07-01 15:53:28 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 113.000000
07-01 15:53:28 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 30.000000
07-01 15:53:28 I/MeasurementPrinter:                   113.0	                OK	                 3261.0	                OK	                            857.0	                OK
07-01 15:53:28 D/RunUtil: Running command null without timeout.
07-01 15:53:28 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:53:29 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:53:29 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:53:29 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:53:30 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:53:30 D/RunUtil: Running command null without timeout.
07-01 15:53:30 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:53:30 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:53:30 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:53:30 D/RunUtil: Running command null without timeout.
07-01 15:53:30 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:53:30 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:53:30 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:53:30 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:53:30 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYWR0LWD package manager
07-01 15:53:30 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYWR0LWD external store
07-01 15:53:31 D/RunUtil: Running command null without timeout.
07-01 15:53:31 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:53:31 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:53:31 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:53:31 D/RunUtil: Running command null without timeout.
07-01 15:53:31 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:53:31 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:53:31 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:53:32 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:53:32 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:53:32 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:53:32 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_2048315141647464733.txt] without timeout.
07-01 15:53:32 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:53:32 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:53:32 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:53:32 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 15:53:59 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:53:59 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 15:53:59 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3478.000000
07-01 15:53:59 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 909.000000
07-01 15:53:59 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 21.000000
07-01 15:53:59 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 387.000000
07-01 15:53:59 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 77.000000
07-01 15:53:59 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 26.000000
07-01 15:53:59 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 9.000000
07-01 15:53:59 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 193.000000
07-01 15:53:59 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2623.000000
07-01 15:53:59 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 56.000000
07-01 15:53:59 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 153.000000
07-01 15:53:59 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2535.000000
07-01 15:53:59 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 912.000000
07-01 15:53:59 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 44.000000
07-01 15:53:59 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 13.000000
07-01 15:53:59 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 46.000000
07-01 15:53:59 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 909.000000
07-01 15:53:59 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 61.000000
07-01 15:53:59 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 152.000000
07-01 15:53:59 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 15:53:59 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 36.000000
07-01 15:53:59 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:53:59 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 15:53:59 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3478.000000
07-01 15:53:59 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 909.000000
07-01 15:53:59 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 21.000000
07-01 15:53:59 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 387.000000
07-01 15:53:59 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 77.000000
07-01 15:53:59 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 26.000000
07-01 15:53:59 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 9.000000
07-01 15:53:59 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 193.000000
07-01 15:53:59 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2623.000000
07-01 15:53:59 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 56.000000
07-01 15:53:59 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 153.000000
07-01 15:53:59 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2535.000000
07-01 15:53:59 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 15:53:59 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 912.000000
07-01 15:53:59 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:53:59 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 44.000000
07-01 15:53:59 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 13.000000
07-01 15:53:59 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 46.000000
07-01 15:53:59 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 909.000000
07-01 15:53:59 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 61.000000
07-01 15:53:59 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 152.000000
07-01 15:53:59 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 36.000000
07-01 15:53:59 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:53:59 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 15:53:59 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3478.000000
07-01 15:53:59 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 909.000000
07-01 15:53:59 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 21.000000
07-01 15:53:59 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 387.000000
07-01 15:53:59 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 77.000000
07-01 15:53:59 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 26.000000
07-01 15:53:59 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 9.000000
07-01 15:53:59 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 193.000000
07-01 15:53:59 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2623.000000
07-01 15:53:59 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 56.000000
07-01 15:53:59 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 153.000000
07-01 15:53:59 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2535.000000
07-01 15:53:59 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 912.000000
07-01 15:53:59 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:53:59 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 44.000000
07-01 15:53:59 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 13.000000
07-01 15:53:59 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 46.000000
07-01 15:53:59 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 909.000000
07-01 15:53:59 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 61.000000
07-01 15:53:59 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 152.000000
07-01 15:53:59 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 36.000000
07-01 15:53:59 I/MeasurementPrinter:                   152.0	                OK	                 3447.0	                OK	                            912.0	                OK
07-01 15:53:59 D/RunUtil: Running command null without timeout.
07-01 15:53:59 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:54:00 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:54:00 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:54:00 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:54:01 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:54:01 D/RunUtil: Running command null without timeout.
07-01 15:54:01 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:54:01 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:54:01 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:54:01 D/RunUtil: Running command null without timeout.
07-01 15:54:01 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:54:01 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:54:01 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:54:01 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:54:01 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYWR0LWD package manager
07-01 15:54:01 I/NativeDeviceStateMonitor: Waiting 359877 ms for device SKBFXYWR0LWD external store
07-01 15:54:02 D/RunUtil: Running command null without timeout.
07-01 15:54:02 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:54:02 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:54:02 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:54:02 D/RunUtil: Running command null without timeout.
07-01 15:54:02 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:54:02 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:54:02 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:54:03 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:54:03 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:54:03 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:54:03 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_5694251621347575758.txt] without timeout.
07-01 15:54:03 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:54:03 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:54:03 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:54:03 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 15:54:29 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:54:30 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 15:54:30 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3314.000000
07-01 15:54:30 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 884.000000
07-01 15:54:30 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 7.000000
07-01 15:54:30 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 497.000000
07-01 15:54:30 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 86.000000
07-01 15:54:30 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 15:54:30 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 15:54:30 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 272.000000
07-01 15:54:30 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2494.000000
07-01 15:54:30 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 32.000000
07-01 15:54:30 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 154.000000
07-01 15:54:30 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2423.000000
07-01 15:54:30 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 905.000000
07-01 15:54:30 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 28.000000
07-01 15:54:30 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 4.000000
07-01 15:54:30 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 30.000000
07-01 15:54:30 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 887.000000
07-01 15:54:30 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 118.000000
07-01 15:54:30 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 156.000000
07-01 15:54:30 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 15:54:30 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 42.000000
07-01 15:54:30 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:54:30 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 15:54:30 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3314.000000
07-01 15:54:30 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 884.000000
07-01 15:54:30 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 7.000000
07-01 15:54:30 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 497.000000
07-01 15:54:30 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 86.000000
07-01 15:54:30 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 15:54:30 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 15:54:30 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 272.000000
07-01 15:54:30 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2494.000000
07-01 15:54:30 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 32.000000
07-01 15:54:30 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 154.000000
07-01 15:54:30 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2423.000000
07-01 15:54:30 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 15:54:30 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 905.000000
07-01 15:54:30 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:54:30 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 28.000000
07-01 15:54:30 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 4.000000
07-01 15:54:30 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 30.000000
07-01 15:54:30 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 887.000000
07-01 15:54:30 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 118.000000
07-01 15:54:30 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 156.000000
07-01 15:54:30 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 42.000000
07-01 15:54:30 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:54:30 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 15:54:30 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3314.000000
07-01 15:54:30 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 884.000000
07-01 15:54:30 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 7.000000
07-01 15:54:30 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 497.000000
07-01 15:54:30 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 86.000000
07-01 15:54:30 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 15:54:30 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 15:54:30 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 272.000000
07-01 15:54:30 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2494.000000
07-01 15:54:30 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 32.000000
07-01 15:54:30 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 154.000000
07-01 15:54:30 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2423.000000
07-01 15:54:30 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 905.000000
07-01 15:54:30 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:54:30 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 28.000000
07-01 15:54:30 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 4.000000
07-01 15:54:30 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 30.000000
07-01 15:54:30 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 887.000000
07-01 15:54:30 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 118.000000
07-01 15:54:30 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 156.000000
07-01 15:54:30 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 42.000000
07-01 15:54:30 I/MeasurementPrinter:                   156.0	                OK	                 3328.0	                OK	                            905.0	                OK
07-01 15:54:30 D/RunUtil: Running command null without timeout.
07-01 15:54:30 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:54:30 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:54:30 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:54:30 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:54:31 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:54:31 D/RunUtil: Running command null without timeout.
07-01 15:54:31 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:54:31 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:54:31 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:54:31 D/RunUtil: Running command null without timeout.
07-01 15:54:31 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:54:31 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:54:31 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:54:31 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:54:31 I/DeviceStateMonitor: Waiting 359959 ms for device SKBFXYWR0LWD package manager
07-01 15:54:31 I/NativeDeviceStateMonitor: Waiting 359898 ms for device SKBFXYWR0LWD external store
07-01 15:54:32 D/RunUtil: Running command null without timeout.
07-01 15:54:32 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:54:33 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:54:33 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:54:33 D/RunUtil: Running command null without timeout.
07-01 15:54:33 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:54:33 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:54:33 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:54:33 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:54:33 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:54:33 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:54:33 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_9881988778819273449.txt] without timeout.
07-01 15:54:34 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:54:34 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:54:34 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:54:34 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 15:55:00 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:55:00 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 15:55:00 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3640.000000
07-01 15:55:00 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 1007.000000
07-01 15:55:00 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 3.000000
07-01 15:55:00 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 532.000000
07-01 15:55:00 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 172.000000
07-01 15:55:00 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 30.000000
07-01 15:55:00 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 3.000000
07-01 15:55:00 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 168.000000
07-01 15:55:00 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2727.000000
07-01 15:55:00 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 50.000000
07-01 15:55:00 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 213.000000
07-01 15:55:00 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2617.000000
07-01 15:55:00 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 1039.000000
07-01 15:55:00 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 34.000000
07-01 15:55:00 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 27.000000
07-01 15:55:00 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 30.000000
07-01 15:55:00 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 1007.000000
07-01 15:55:00 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 119.000000
07-01 15:55:00 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 213.000000
07-01 15:55:00 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 15:55:00 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 85.000000
07-01 15:55:00 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:55:00 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 15:55:00 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3640.000000
07-01 15:55:00 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 1007.000000
07-01 15:55:00 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 3.000000
07-01 15:55:00 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 532.000000
07-01 15:55:00 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 172.000000
07-01 15:55:00 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 30.000000
07-01 15:55:00 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 3.000000
07-01 15:55:00 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 168.000000
07-01 15:55:00 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2727.000000
07-01 15:55:00 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 50.000000
07-01 15:55:00 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 213.000000
07-01 15:55:00 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2617.000000
07-01 15:55:00 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 15:55:00 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 1039.000000
07-01 15:55:00 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:55:00 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 34.000000
07-01 15:55:00 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 27.000000
07-01 15:55:00 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 30.000000
07-01 15:55:00 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 1007.000000
07-01 15:55:00 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 119.000000
07-01 15:55:00 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 213.000000
07-01 15:55:00 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 85.000000
07-01 15:55:00 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:55:01 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 15:55:01 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3640.000000
07-01 15:55:01 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 1007.000000
07-01 15:55:01 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 3.000000
07-01 15:55:01 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 532.000000
07-01 15:55:01 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 172.000000
07-01 15:55:01 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 30.000000
07-01 15:55:01 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 3.000000
07-01 15:55:01 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 168.000000
07-01 15:55:01 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2727.000000
07-01 15:55:01 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 50.000000
07-01 15:55:01 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 213.000000
07-01 15:55:01 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2617.000000
07-01 15:55:01 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 1039.000000
07-01 15:55:01 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:55:01 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 34.000000
07-01 15:55:01 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 27.000000
07-01 15:55:01 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 30.000000
07-01 15:55:01 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 1007.000000
07-01 15:55:01 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 119.000000
07-01 15:55:01 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 213.000000
07-01 15:55:01 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 85.000000
07-01 15:55:01 I/MeasurementPrinter:                   213.0	                OK	                 3656.0	                OK	                           1039.0	                OK
07-01 15:55:01 D/RunUtil: Running command null without timeout.
07-01 15:55:01 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:55:01 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:55:01 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:55:01 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:55:02 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:55:02 D/RunUtil: Running command null without timeout.
07-01 15:55:02 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:55:02 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:55:02 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:55:02 D/RunUtil: Running command null without timeout.
07-01 15:55:02 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:55:02 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:55:02 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:55:02 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:55:02 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYWR0LWD package manager
07-01 15:55:02 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYWR0LWD external store
07-01 15:55:03 D/RunUtil: Running command null without timeout.
07-01 15:55:03 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:55:03 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:55:03 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:55:03 D/RunUtil: Running command null without timeout.
07-01 15:55:03 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:55:03 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:55:04 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:55:04 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:55:04 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:55:04 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:55:04 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_10744234842676239683.txt] without timeout.
07-01 15:55:04 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:55:04 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:55:04 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:55:04 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 15:55:30 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:55:30 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 15:55:30 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3287.000000
07-01 15:55:30 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 870.000000
07-01 15:55:30 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 19.000000
07-01 15:55:30 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 389.000000
07-01 15:55:30 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 108.000000
07-01 15:55:30 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 15:55:30 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 15:55:30 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 256.000000
07-01 15:55:30 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2470.000000
07-01 15:55:30 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 68.000000
07-01 15:55:30 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 154.000000
07-01 15:55:30 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2405.000000
07-01 15:55:30 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 875.000000
07-01 15:55:30 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 56.000000
07-01 15:55:30 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 12.000000
07-01 15:55:30 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 31.000000
07-01 15:55:30 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 872.000000
07-01 15:55:30 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 80.000000
07-01 15:55:30 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 156.000000
07-01 15:55:30 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 15:55:30 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 62.000000
07-01 15:55:30 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:55:31 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 15:55:31 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3287.000000
07-01 15:55:31 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 870.000000
07-01 15:55:31 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 19.000000
07-01 15:55:31 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 389.000000
07-01 15:55:31 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 108.000000
07-01 15:55:31 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 15:55:31 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 15:55:31 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 256.000000
07-01 15:55:31 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2470.000000
07-01 15:55:31 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 68.000000
07-01 15:55:31 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 154.000000
07-01 15:55:31 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2405.000000
07-01 15:55:31 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 15:55:31 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 875.000000
07-01 15:55:31 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:55:31 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 56.000000
07-01 15:55:31 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 12.000000
07-01 15:55:31 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 31.000000
07-01 15:55:31 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 872.000000
07-01 15:55:31 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 80.000000
07-01 15:55:31 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 156.000000
07-01 15:55:31 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 62.000000
07-01 15:55:31 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:55:31 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 15:55:31 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3287.000000
07-01 15:55:31 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 870.000000
07-01 15:55:31 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 19.000000
07-01 15:55:31 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 389.000000
07-01 15:55:31 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 108.000000
07-01 15:55:31 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 15:55:31 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 15:55:31 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 256.000000
07-01 15:55:31 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2470.000000
07-01 15:55:31 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 68.000000
07-01 15:55:31 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 154.000000
07-01 15:55:31 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2405.000000
07-01 15:55:31 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 875.000000
07-01 15:55:31 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:55:31 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 56.000000
07-01 15:55:31 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 12.000000
07-01 15:55:31 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 31.000000
07-01 15:55:31 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 872.000000
07-01 15:55:31 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 80.000000
07-01 15:55:31 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 156.000000
07-01 15:55:31 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 62.000000
07-01 15:55:31 I/MeasurementPrinter:                   156.0	                OK	                 3280.0	                OK	                            875.0	                OK
07-01 15:55:31 D/RunUtil: Running command null without timeout.
07-01 15:55:31 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:55:31 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:55:31 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:55:31 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:55:32 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:55:32 D/RunUtil: Running command null without timeout.
07-01 15:55:32 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:55:32 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:55:32 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:55:32 D/RunUtil: Running command null without timeout.
07-01 15:55:32 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:55:32 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:55:32 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:55:32 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:55:32 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYWR0LWD package manager
07-01 15:55:32 I/NativeDeviceStateMonitor: Waiting 359877 ms for device SKBFXYWR0LWD external store
07-01 15:55:33 D/RunUtil: Running command null without timeout.
07-01 15:55:34 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:55:34 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:55:34 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:55:34 D/RunUtil: Running command null without timeout.
07-01 15:55:34 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:55:34 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:55:34 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:55:34 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:55:34 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:55:34 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:55:34 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_4355014153971494465.txt] without timeout.
07-01 15:55:35 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:55:35 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:55:35 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:55:35 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 15:56:01 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:56:01 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 15:56:01 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3080.000000
07-01 15:56:01 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 812.000000
07-01 15:56:01 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 8.000000
07-01 15:56:01 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 802.000000
07-01 15:56:01 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 16.000000
07-01 15:56:01 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 7.000000
07-01 15:56:01 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 15:56:01 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 282.000000
07-01 15:56:01 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2312.000000
07-01 15:56:01 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 61.000000
07-01 15:56:01 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 239.000000
07-01 15:56:01 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2236.000000
07-01 15:56:01 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 819.000000
07-01 15:56:01 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 56.000000
07-01 15:56:01 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 15:56:01 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 14.000000
07-01 15:56:01 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 818.000000
07-01 15:56:01 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 168.000000
07-01 15:56:01 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 237.000000
07-01 15:56:01 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 15:56:01 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 35.000000
07-01 15:56:01 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:56:01 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 15:56:01 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3080.000000
07-01 15:56:01 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 812.000000
07-01 15:56:01 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 8.000000
07-01 15:56:01 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 802.000000
07-01 15:56:01 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 16.000000
07-01 15:56:01 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 7.000000
07-01 15:56:01 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 15:56:01 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 282.000000
07-01 15:56:01 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2312.000000
07-01 15:56:01 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 61.000000
07-01 15:56:01 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 239.000000
07-01 15:56:01 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2236.000000
07-01 15:56:01 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 15:56:01 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 819.000000
07-01 15:56:01 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:56:01 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 56.000000
07-01 15:56:01 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 15:56:01 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 14.000000
07-01 15:56:01 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 818.000000
07-01 15:56:01 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 168.000000
07-01 15:56:01 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 237.000000
07-01 15:56:01 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 35.000000
07-01 15:56:01 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:56:01 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 15:56:01 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3080.000000
07-01 15:56:01 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 812.000000
07-01 15:56:01 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 8.000000
07-01 15:56:01 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 802.000000
07-01 15:56:01 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 16.000000
07-01 15:56:01 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 7.000000
07-01 15:56:01 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 15:56:01 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 282.000000
07-01 15:56:01 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2312.000000
07-01 15:56:01 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 61.000000
07-01 15:56:01 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 239.000000
07-01 15:56:01 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2236.000000
07-01 15:56:01 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 819.000000
07-01 15:56:01 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:56:01 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 56.000000
07-01 15:56:01 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 15:56:01 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 14.000000
07-01 15:56:01 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 818.000000
07-01 15:56:01 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 168.000000
07-01 15:56:01 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 237.000000
07-01 15:56:01 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 35.000000
07-01 15:56:01 I/MeasurementPrinter:                   237.0	                OK	                 3055.0	                OK	                            819.0	                OK
07-01 15:56:01 D/RunUtil: Running command null without timeout.
07-01 15:56:01 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:56:02 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:56:02 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:56:02 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:56:02 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:56:02 D/RunUtil: Running command null without timeout.
07-01 15:56:02 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:56:02 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:56:02 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:56:02 D/RunUtil: Running command null without timeout.
07-01 15:56:02 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:56:02 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:56:02 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:56:02 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:56:02 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYWR0LWD package manager
07-01 15:56:03 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYWR0LWD external store
07-01 15:56:04 D/RunUtil: Running command null without timeout.
07-01 15:56:04 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:56:04 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:56:04 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:56:04 D/RunUtil: Running command null without timeout.
07-01 15:56:04 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:56:04 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:56:04 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:56:04 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:56:04 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:56:04 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:56:05 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_15197178763119190352.txt] without timeout.
07-01 15:56:05 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:56:05 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:56:05 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:56:05 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 15:56:31 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:56:31 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 15:56:31 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3425.000000
07-01 15:56:31 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 826.000000
07-01 15:56:31 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 2.000000
07-01 15:56:31 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 331.000000
07-01 15:56:31 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 86.000000
07-01 15:56:31 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 15:56:31 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 6.000000
07-01 15:56:31 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 96.000000
07-01 15:56:31 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2656.000000
07-01 15:56:31 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 16.000000
07-01 15:56:31 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 111.000000
07-01 15:56:31 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2551.000000
07-01 15:56:31 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 830.000000
07-01 15:56:31 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 10.000000
07-01 15:56:31 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 15:56:31 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 19.000000
07-01 15:56:31 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 827.000000
07-01 15:56:31 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 85.000000
07-01 15:56:31 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 111.000000
07-01 15:56:31 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 15:56:31 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 15.000000
07-01 15:56:31 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:56:31 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 15:56:31 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3425.000000
07-01 15:56:31 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 826.000000
07-01 15:56:31 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 2.000000
07-01 15:56:31 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 331.000000
07-01 15:56:31 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 86.000000
07-01 15:56:31 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 15:56:31 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 6.000000
07-01 15:56:31 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 96.000000
07-01 15:56:31 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2656.000000
07-01 15:56:31 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 16.000000
07-01 15:56:31 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 111.000000
07-01 15:56:31 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2551.000000
07-01 15:56:31 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 15:56:31 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 830.000000
07-01 15:56:31 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:56:31 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 10.000000
07-01 15:56:31 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 15:56:31 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 19.000000
07-01 15:56:31 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 827.000000
07-01 15:56:31 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 85.000000
07-01 15:56:31 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 111.000000
07-01 15:56:31 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 15.000000
07-01 15:56:31 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:56:31 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 15:56:31 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3425.000000
07-01 15:56:31 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 826.000000
07-01 15:56:31 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 2.000000
07-01 15:56:31 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 331.000000
07-01 15:56:31 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 86.000000
07-01 15:56:31 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 15:56:31 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 6.000000
07-01 15:56:31 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 96.000000
07-01 15:56:31 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2656.000000
07-01 15:56:31 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 16.000000
07-01 15:56:31 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 111.000000
07-01 15:56:31 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2551.000000
07-01 15:56:31 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 830.000000
07-01 15:56:31 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:56:31 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 10.000000
07-01 15:56:31 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 15:56:31 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 19.000000
07-01 15:56:31 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 827.000000
07-01 15:56:31 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 85.000000
07-01 15:56:31 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 111.000000
07-01 15:56:31 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 15.000000
07-01 15:56:31 I/MeasurementPrinter:                   111.0	                OK	                 3381.0	                OK	                            830.0	                OK
07-01 15:56:31 D/RunUtil: Running command null without timeout.
07-01 15:56:31 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:56:32 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:56:32 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:56:32 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:56:33 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:56:33 D/RunUtil: Running command null without timeout.
07-01 15:56:33 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:56:33 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:56:33 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:56:33 D/RunUtil: Running command null without timeout.
07-01 15:56:33 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:56:33 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:56:33 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:56:33 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYWR0LWD boot complete
07-01 15:56:33 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYWR0LWD package manager
07-01 15:56:33 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYWR0LWD external store
07-01 15:56:34 D/RunUtil: Running command null without timeout.
07-01 15:56:34 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:56:34 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:56:34 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:56:34 D/RunUtil: Running command null without timeout.
07-01 15:56:34 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:56:34 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:56:34 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:56:35 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:56:35 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:56:35 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:56:35 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_9671661710076996160.txt] without timeout.
07-01 15:56:35 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:56:35 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:56:35 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:56:35 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 15:57:04 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:57:05 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 15:57:05 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3652.000000
07-01 15:57:05 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 781.000000
07-01 15:57:05 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 27.000000
07-01 15:57:05 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 488.000000
07-01 15:57:05 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 66.000000
07-01 15:57:05 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 35.000000
07-01 15:57:05 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 47.000000
07-01 15:57:05 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 271.000000
07-01 15:57:05 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2913.000000
07-01 15:57:05 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 60.000000
07-01 15:57:05 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 221.000000
07-01 15:57:05 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2835.000000
07-01 15:57:05 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 785.000000
07-01 15:57:05 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 48.000000
07-01 15:57:05 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 13.000000
07-01 15:57:05 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 11.000000
07-01 15:57:05 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 782.000000
07-01 15:57:05 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 78.000000
07-01 15:57:05 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 225.000000
07-01 15:57:05 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 15:57:05 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 17.000000
07-01 15:57:05 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:57:05 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 15:57:05 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3652.000000
07-01 15:57:05 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 781.000000
07-01 15:57:05 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 27.000000
07-01 15:57:05 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 488.000000
07-01 15:57:05 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 66.000000
07-01 15:57:05 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 35.000000
07-01 15:57:05 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 47.000000
07-01 15:57:05 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 271.000000
07-01 15:57:05 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2913.000000
07-01 15:57:05 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 60.000000
07-01 15:57:05 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 221.000000
07-01 15:57:05 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2835.000000
07-01 15:57:05 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 15:57:05 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 785.000000
07-01 15:57:05 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:57:05 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 48.000000
07-01 15:57:05 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 13.000000
07-01 15:57:05 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 11.000000
07-01 15:57:05 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 782.000000
07-01 15:57:05 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 78.000000
07-01 15:57:05 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 225.000000
07-01 15:57:05 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 17.000000
07-01 15:57:05 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:57:05 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 15:57:05 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3652.000000
07-01 15:57:05 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 781.000000
07-01 15:57:05 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 27.000000
07-01 15:57:05 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 488.000000
07-01 15:57:05 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 66.000000
07-01 15:57:05 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 35.000000
07-01 15:57:05 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 47.000000
07-01 15:57:05 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 271.000000
07-01 15:57:05 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2913.000000
07-01 15:57:05 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 60.000000
07-01 15:57:05 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 221.000000
07-01 15:57:05 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2835.000000
07-01 15:57:05 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 785.000000
07-01 15:57:05 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:57:05 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 48.000000
07-01 15:57:05 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 13.000000
07-01 15:57:05 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 11.000000
07-01 15:57:05 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 782.000000
07-01 15:57:05 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 78.000000
07-01 15:57:05 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 225.000000
07-01 15:57:05 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 17.000000
07-01 15:57:05 I/MeasurementPrinter:                   225.0	                OK	                 3620.0	                OK	                            785.0	                OK
07-01 15:57:05 D/RunUtil: Running command null without timeout.
07-01 15:57:05 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:57:05 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:57:05 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:57:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:57:06 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:57:06 D/RunUtil: Running command null without timeout.
07-01 15:57:06 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:57:06 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:57:06 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:57:06 D/RunUtil: Running command null without timeout.
07-01 15:57:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:57:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:57:06 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:57:06 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:57:06 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYWR0LWD package manager
07-01 15:57:06 I/NativeDeviceStateMonitor: Waiting 359903 ms for device SKBFXYWR0LWD external store
07-01 15:57:07 D/RunUtil: Running command null without timeout.
07-01 15:57:07 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:57:08 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:57:08 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:57:08 D/RunUtil: Running command null without timeout.
07-01 15:57:08 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:57:08 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:57:08 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:57:08 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:57:08 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:57:08 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:57:08 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_10400308979271217678.txt] without timeout.
07-01 15:57:09 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:57:09 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:57:09 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:57:09 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 15:57:36 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:57:36 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 15:57:36 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3764.000000
07-01 15:57:36 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 1018.000000
07-01 15:57:36 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 28.000000
07-01 15:57:36 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 729.000000
07-01 15:57:36 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 62.000000
07-01 15:57:36 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 15:57:36 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 15:57:36 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 458.000000
07-01 15:57:36 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2909.000000
07-01 15:57:36 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 39.000000
07-01 15:57:36 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 135.000000
07-01 15:57:36 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2732.000000
07-01 15:57:36 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 1024.000000
07-01 15:57:36 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 33.000000
07-01 15:57:36 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 6.000000
07-01 15:57:36 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 61.000000
07-01 15:57:36 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 1021.000000
07-01 15:57:36 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 90.000000
07-01 15:57:36 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 136.000000
07-01 15:57:36 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 15:57:36 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 48.000000
07-01 15:57:36 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:57:36 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 15:57:36 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3764.000000
07-01 15:57:36 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 1018.000000
07-01 15:57:36 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 28.000000
07-01 15:57:36 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 729.000000
07-01 15:57:36 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 62.000000
07-01 15:57:36 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 15:57:36 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 15:57:36 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 458.000000
07-01 15:57:36 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2909.000000
07-01 15:57:36 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 39.000000
07-01 15:57:36 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 135.000000
07-01 15:57:36 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2732.000000
07-01 15:57:36 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 15:57:36 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 1024.000000
07-01 15:57:36 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:57:36 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 33.000000
07-01 15:57:36 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 6.000000
07-01 15:57:36 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 61.000000
07-01 15:57:36 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 1021.000000
07-01 15:57:36 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 90.000000
07-01 15:57:36 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 136.000000
07-01 15:57:36 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 48.000000
07-01 15:57:36 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:57:36 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 15:57:36 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3764.000000
07-01 15:57:36 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 1018.000000
07-01 15:57:36 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 28.000000
07-01 15:57:36 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 729.000000
07-01 15:57:36 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 62.000000
07-01 15:57:36 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 15:57:36 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 15:57:36 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 458.000000
07-01 15:57:36 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2909.000000
07-01 15:57:36 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 39.000000
07-01 15:57:36 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 135.000000
07-01 15:57:36 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2732.000000
07-01 15:57:36 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 1024.000000
07-01 15:57:36 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:57:36 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 33.000000
07-01 15:57:36 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 6.000000
07-01 15:57:36 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 61.000000
07-01 15:57:36 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 1021.000000
07-01 15:57:36 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 90.000000
07-01 15:57:36 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 136.000000
07-01 15:57:36 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 48.000000
07-01 15:57:36 I/MeasurementPrinter:                   136.0	                OK	                 3756.0	                OK	                           1024.0	                OK
07-01 15:57:36 D/RunUtil: Running command null without timeout.
07-01 15:57:36 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:57:37 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:57:37 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:57:37 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:57:37 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:57:37 D/RunUtil: Running command null without timeout.
07-01 15:57:37 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:57:38 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:57:38 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:57:38 D/RunUtil: Running command null without timeout.
07-01 15:57:38 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:57:38 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:57:38 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:57:38 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:57:38 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYWR0LWD package manager
07-01 15:57:38 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYWR0LWD external store
07-01 15:57:39 D/RunUtil: Running command null without timeout.
07-01 15:57:39 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:57:39 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:57:39 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:57:39 D/RunUtil: Running command null without timeout.
07-01 15:57:39 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:57:39 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:57:39 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:57:40 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:57:40 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:57:40 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:57:40 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_14123763477604311137.txt] without timeout.
07-01 15:57:40 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:57:40 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:57:40 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:57:40 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 15:58:07 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:58:07 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 15:58:07 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3431.000000
07-01 15:58:07 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 778.000000
07-01 15:58:07 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 31.000000
07-01 15:58:07 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 457.000000
07-01 15:58:07 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 55.000000
07-01 15:58:07 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 15:58:07 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 11.000000
07-01 15:58:07 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 366.000000
07-01 15:58:07 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2689.000000
07-01 15:58:07 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 23.000000
07-01 15:58:07 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 153.000000
07-01 15:58:07 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2625.000000
07-01 15:58:07 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 802.000000
07-01 15:58:07 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 17.000000
07-01 15:58:07 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 6.000000
07-01 15:58:07 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 16.000000
07-01 15:58:07 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 778.000000
07-01 15:58:07 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 115.000000
07-01 15:58:07 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 153.000000
07-01 15:58:07 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 15:58:07 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 38.000000
07-01 15:58:07 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:58:07 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 15:58:07 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3431.000000
07-01 15:58:07 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 778.000000
07-01 15:58:07 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 31.000000
07-01 15:58:07 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 457.000000
07-01 15:58:07 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 55.000000
07-01 15:58:07 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 15:58:07 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 11.000000
07-01 15:58:07 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 366.000000
07-01 15:58:07 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2689.000000
07-01 15:58:07 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 23.000000
07-01 15:58:07 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 153.000000
07-01 15:58:07 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2625.000000
07-01 15:58:07 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 15:58:07 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 802.000000
07-01 15:58:07 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:58:07 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 17.000000
07-01 15:58:07 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 6.000000
07-01 15:58:07 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 16.000000
07-01 15:58:07 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 778.000000
07-01 15:58:07 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 115.000000
07-01 15:58:07 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 153.000000
07-01 15:58:07 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 38.000000
07-01 15:58:07 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:58:07 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 15:58:07 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3431.000000
07-01 15:58:07 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 778.000000
07-01 15:58:07 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 31.000000
07-01 15:58:07 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 457.000000
07-01 15:58:07 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 55.000000
07-01 15:58:07 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 15:58:07 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 11.000000
07-01 15:58:07 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 366.000000
07-01 15:58:07 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2689.000000
07-01 15:58:07 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 23.000000
07-01 15:58:07 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 153.000000
07-01 15:58:07 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2625.000000
07-01 15:58:07 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 802.000000
07-01 15:58:07 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:58:07 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 17.000000
07-01 15:58:07 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 6.000000
07-01 15:58:07 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 16.000000
07-01 15:58:07 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 778.000000
07-01 15:58:07 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 115.000000
07-01 15:58:07 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 153.000000
07-01 15:58:07 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 38.000000
07-01 15:58:07 I/MeasurementPrinter:                   153.0	                OK	                 3427.0	                OK	                            802.0	                OK
07-01 15:58:07 D/RunUtil: Running command null without timeout.
07-01 15:58:07 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:58:08 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:58:08 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:58:08 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:58:08 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:58:08 D/RunUtil: Running command null without timeout.
07-01 15:58:08 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:58:08 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:58:08 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:58:08 D/RunUtil: Running command null without timeout.
07-01 15:58:08 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:58:08 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:58:08 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:58:08 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:58:09 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYWR0LWD package manager
07-01 15:58:09 I/NativeDeviceStateMonitor: Waiting 359903 ms for device SKBFXYWR0LWD external store
07-01 15:58:10 D/RunUtil: Running command null without timeout.
07-01 15:58:10 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:58:10 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:58:10 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:58:10 D/RunUtil: Running command null without timeout.
07-01 15:58:10 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:58:10 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:58:10 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:58:10 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:58:10 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:58:10 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:58:11 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_13066556136521481027.txt] without timeout.
07-01 15:58:11 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:58:11 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:58:11 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:58:11 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 15:58:37 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:58:37 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 15:58:37 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3423.000000
07-01 15:58:37 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 894.000000
07-01 15:58:37 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 4.000000
07-01 15:58:37 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 552.000000
07-01 15:58:37 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 150.000000
07-01 15:58:37 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 8.000000
07-01 15:58:37 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 15:58:37 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 483.000000
07-01 15:58:37 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2579.000000
07-01 15:58:37 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 24.000000
07-01 15:58:37 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 118.000000
07-01 15:58:37 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2524.000000
07-01 15:58:37 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 916.000000
07-01 15:58:37 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 16.000000
07-01 15:58:37 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 8.000000
07-01 15:58:37 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 67.000000
07-01 15:58:37 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 894.000000
07-01 15:58:37 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 84.000000
07-01 15:58:37 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 119.000000
07-01 15:58:37 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 15:58:37 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 15:58:37 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:58:37 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 15:58:37 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3423.000000
07-01 15:58:37 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 894.000000
07-01 15:58:37 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 4.000000
07-01 15:58:37 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 552.000000
07-01 15:58:37 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 150.000000
07-01 15:58:37 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 8.000000
07-01 15:58:37 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 15:58:37 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 483.000000
07-01 15:58:37 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2579.000000
07-01 15:58:37 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 24.000000
07-01 15:58:37 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 118.000000
07-01 15:58:37 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2524.000000
07-01 15:58:37 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 15:58:37 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 916.000000
07-01 15:58:37 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:58:37 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 16.000000
07-01 15:58:37 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 8.000000
07-01 15:58:37 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 67.000000
07-01 15:58:37 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 894.000000
07-01 15:58:37 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 84.000000
07-01 15:58:37 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 119.000000
07-01 15:58:37 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 15:58:37 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:58:37 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 15:58:37 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3423.000000
07-01 15:58:37 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 894.000000
07-01 15:58:37 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 4.000000
07-01 15:58:37 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 552.000000
07-01 15:58:37 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 150.000000
07-01 15:58:37 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 8.000000
07-01 15:58:37 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 15:58:37 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 483.000000
07-01 15:58:37 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2579.000000
07-01 15:58:37 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 24.000000
07-01 15:58:37 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 118.000000
07-01 15:58:37 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2524.000000
07-01 15:58:37 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 916.000000
07-01 15:58:37 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:58:37 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 16.000000
07-01 15:58:37 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 8.000000
07-01 15:58:37 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 67.000000
07-01 15:58:37 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 894.000000
07-01 15:58:37 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 84.000000
07-01 15:58:37 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 119.000000
07-01 15:58:37 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 15:58:37 I/MeasurementPrinter:                   119.0	                OK	                 3440.0	                OK	                            916.0	                OK
07-01 15:58:37 D/RunUtil: Running command null without timeout.
07-01 15:58:38 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:58:38 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:58:38 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:58:38 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:58:39 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:58:39 D/RunUtil: Running command null without timeout.
07-01 15:58:39 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:58:39 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:58:39 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:58:39 D/RunUtil: Running command null without timeout.
07-01 15:58:39 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:58:39 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:58:39 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:58:39 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:58:39 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYWR0LWD package manager
07-01 15:58:39 I/NativeDeviceStateMonitor: Waiting 359903 ms for device SKBFXYWR0LWD external store
07-01 15:58:40 D/RunUtil: Running command null without timeout.
07-01 15:58:40 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:58:40 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:58:40 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:58:40 D/RunUtil: Running command null without timeout.
07-01 15:58:40 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:58:40 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:58:40 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:58:41 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:58:41 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:58:41 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:58:41 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_3417406357915011363.txt] without timeout.
07-01 15:58:41 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:58:41 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:58:41 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:58:41 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 15:59:08 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:59:08 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 15:59:08 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3628.000000
07-01 15:59:08 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 863.000000
07-01 15:59:08 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 68.000000
07-01 15:59:08 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 601.000000
07-01 15:59:08 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 116.000000
07-01 15:59:08 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 18.000000
07-01 15:59:08 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 53.000000
07-01 15:59:08 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 203.000000
07-01 15:59:08 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2798.000000
07-01 15:59:08 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 51.000000
07-01 15:59:08 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 225.000000
07-01 15:59:08 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2745.000000
07-01 15:59:08 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 885.000000
07-01 15:59:08 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 45.000000
07-01 15:59:08 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 15:59:08 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 6.000000
07-01 15:59:08 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 864.000000
07-01 15:59:08 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 102.000000
07-01 15:59:08 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 231.000000
07-01 15:59:08 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 15:59:08 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 22.000000
07-01 15:59:08 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:59:08 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 15:59:08 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3628.000000
07-01 15:59:08 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 863.000000
07-01 15:59:08 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 68.000000
07-01 15:59:08 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 601.000000
07-01 15:59:08 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 116.000000
07-01 15:59:08 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 18.000000
07-01 15:59:08 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 53.000000
07-01 15:59:08 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 203.000000
07-01 15:59:08 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2798.000000
07-01 15:59:08 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 51.000000
07-01 15:59:08 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 225.000000
07-01 15:59:08 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2745.000000
07-01 15:59:08 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 15:59:08 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 885.000000
07-01 15:59:08 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:59:08 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 45.000000
07-01 15:59:08 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 15:59:08 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 6.000000
07-01 15:59:08 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 864.000000
07-01 15:59:08 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 102.000000
07-01 15:59:08 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 231.000000
07-01 15:59:08 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 22.000000
07-01 15:59:08 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:59:08 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 15:59:08 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3628.000000
07-01 15:59:08 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 863.000000
07-01 15:59:08 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 68.000000
07-01 15:59:08 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 601.000000
07-01 15:59:08 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 116.000000
07-01 15:59:08 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 18.000000
07-01 15:59:08 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 53.000000
07-01 15:59:08 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 203.000000
07-01 15:59:08 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2798.000000
07-01 15:59:08 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 51.000000
07-01 15:59:08 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 225.000000
07-01 15:59:08 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2745.000000
07-01 15:59:08 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 885.000000
07-01 15:59:08 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:59:08 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 45.000000
07-01 15:59:08 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 15:59:08 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 6.000000
07-01 15:59:08 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 864.000000
07-01 15:59:08 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 102.000000
07-01 15:59:08 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 231.000000
07-01 15:59:08 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 22.000000
07-01 15:59:08 I/MeasurementPrinter:                   231.0	                OK	                 3630.0	                OK	                            885.0	                OK
07-01 15:59:08 D/RunUtil: Running command null without timeout.
07-01 15:59:09 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:59:09 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:59:09 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:59:09 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:59:10 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:59:10 D/RunUtil: Running command null without timeout.
07-01 15:59:10 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:59:10 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:59:10 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:59:10 D/RunUtil: Running command null without timeout.
07-01 15:59:10 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:59:10 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:59:10 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:59:10 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:59:10 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYWR0LWD package manager
07-01 15:59:10 I/NativeDeviceStateMonitor: Waiting 359877 ms for device SKBFXYWR0LWD external store
07-01 15:59:11 D/RunUtil: Running command null without timeout.
07-01 15:59:11 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:59:11 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:59:11 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:59:11 D/RunUtil: Running command null without timeout.
07-01 15:59:11 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:59:11 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:59:12 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:59:12 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:59:12 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:59:12 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:59:12 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_4379262159991059631.txt] without timeout.
07-01 15:59:12 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:59:12 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:59:12 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:59:12 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 15:59:39 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:59:39 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 15:59:39 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3351.000000
07-01 15:59:39 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 985.000000
07-01 15:59:39 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 3.000000
07-01 15:59:39 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 337.000000
07-01 15:59:39 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 10.000000
07-01 15:59:39 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 345.000000
07-01 15:59:39 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2438.000000
07-01 15:59:39 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 63.000000
07-01 15:59:39 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2360.000000
07-01 15:59:39 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 990.000000
07-01 15:59:39 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 28.000000
07-01 15:59:39 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 985.000000
07-01 15:59:39 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 145.000000
07-01 15:59:39 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 15:59:39 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 43.000000
07-01 15:59:39 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:59:39 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 15:59:39 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3351.000000
07-01 15:59:39 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 985.000000
07-01 15:59:39 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 3.000000
07-01 15:59:39 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 337.000000
07-01 15:59:39 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 10.000000
07-01 15:59:39 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 345.000000
07-01 15:59:39 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2438.000000
07-01 15:59:39 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 63.000000
07-01 15:59:39 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2360.000000
07-01 15:59:39 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 15:59:39 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 990.000000
07-01 15:59:39 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:59:39 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 28.000000
07-01 15:59:39 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 985.000000
07-01 15:59:39 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 145.000000
07-01 15:59:39 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 43.000000
07-01 15:59:39 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:59:39 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 15:59:39 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3351.000000
07-01 15:59:39 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 985.000000
07-01 15:59:39 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 3.000000
07-01 15:59:39 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 337.000000
07-01 15:59:39 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 10.000000
07-01 15:59:39 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 345.000000
07-01 15:59:39 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2438.000000
07-01 15:59:39 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 63.000000
07-01 15:59:39 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2360.000000
07-01 15:59:39 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 990.000000
07-01 15:59:39 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 15:59:39 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 28.000000
07-01 15:59:39 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 985.000000
07-01 15:59:39 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 145.000000
07-01 15:59:39 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 43.000000
07-01 15:59:39 I/MeasurementPrinter:                   145.0	                OK	                 3350.0	                OK	                            990.0	                OK
07-01 15:59:39 D/RunUtil: Running command null without timeout.
07-01 15:59:39 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:59:40 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:59:40 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:59:40 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:59:40 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:59:40 D/RunUtil: Running command null without timeout.
07-01 15:59:40 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:59:40 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:59:40 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:59:40 D/RunUtil: Running command null without timeout.
07-01 15:59:41 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:59:41 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:59:41 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 15:59:41 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 15:59:41 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYWR0LWD package manager
07-01 15:59:41 I/NativeDeviceStateMonitor: Waiting 359877 ms for device SKBFXYWR0LWD external store
07-01 15:59:42 D/RunUtil: Running command null without timeout.
07-01 15:59:42 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:59:42 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 15:59:42 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 15:59:42 D/RunUtil: Running command null without timeout.
07-01 15:59:42 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:59:42 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:59:42 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 15:59:43 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 15:59:43 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 15:59:43 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 15:59:43 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_2574032204199003709.txt] without timeout.
07-01 15:59:43 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 15:59:43 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 15:59:43 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 15:59:43 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:00:10 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:00:10 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:00:10 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3485.000000
07-01 16:00:10 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 897.000000
07-01 16:00:10 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 74.000000
07-01 16:00:10 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 453.000000
07-01 16:00:10 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 105.000000
07-01 16:00:10 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 42.000000
07-01 16:00:10 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 23.000000
07-01 16:00:10 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 324.000000
07-01 16:00:10 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2641.000000
07-01 16:00:10 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 68.000000
07-01 16:00:10 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 267.000000
07-01 16:00:10 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2582.000000
07-01 16:00:10 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 920.000000
07-01 16:00:10 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 27.000000
07-01 16:00:10 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 41.000000
07-01 16:00:10 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 30.000000
07-01 16:00:10 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 899.000000
07-01 16:00:10 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 134.000000
07-01 16:00:10 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 268.000000
07-01 16:00:10 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:00:10 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 25.000000
07-01 16:00:10 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:00:10 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:00:10 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3485.000000
07-01 16:00:10 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 897.000000
07-01 16:00:10 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 74.000000
07-01 16:00:10 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 453.000000
07-01 16:00:10 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 105.000000
07-01 16:00:10 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 42.000000
07-01 16:00:10 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 23.000000
07-01 16:00:10 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 324.000000
07-01 16:00:10 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2641.000000
07-01 16:00:10 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 68.000000
07-01 16:00:10 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 267.000000
07-01 16:00:10 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2582.000000
07-01 16:00:10 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:00:10 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 920.000000
07-01 16:00:10 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:00:10 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 27.000000
07-01 16:00:10 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 41.000000
07-01 16:00:10 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 30.000000
07-01 16:00:10 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 899.000000
07-01 16:00:10 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 134.000000
07-01 16:00:10 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 268.000000
07-01 16:00:10 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 25.000000
07-01 16:00:10 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:00:10 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:00:10 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3485.000000
07-01 16:00:10 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 897.000000
07-01 16:00:10 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 74.000000
07-01 16:00:10 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 453.000000
07-01 16:00:10 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 105.000000
07-01 16:00:10 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 42.000000
07-01 16:00:10 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 23.000000
07-01 16:00:10 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 324.000000
07-01 16:00:10 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2641.000000
07-01 16:00:10 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 68.000000
07-01 16:00:10 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 267.000000
07-01 16:00:10 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2582.000000
07-01 16:00:10 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 920.000000
07-01 16:00:10 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:00:10 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 27.000000
07-01 16:00:10 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 41.000000
07-01 16:00:10 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 30.000000
07-01 16:00:10 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 899.000000
07-01 16:00:10 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 134.000000
07-01 16:00:10 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 268.000000
07-01 16:00:10 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 25.000000
07-01 16:00:10 I/MeasurementPrinter:                   268.0	                OK	                 3502.0	                OK	                            920.0	                OK
07-01 16:00:10 D/RunUtil: Running command null without timeout.
07-01 16:00:10 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:00:11 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:00:11 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:00:11 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:00:11 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:00:11 D/RunUtil: Running command null without timeout.
07-01 16:00:12 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:00:12 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:00:12 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:00:12 D/RunUtil: Running command null without timeout.
07-01 16:00:12 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:00:12 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:00:12 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 16:00:12 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYWR0LWD boot complete
07-01 16:00:12 I/DeviceStateMonitor: Waiting 359963 ms for device SKBFXYWR0LWD package manager
07-01 16:00:12 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYWR0LWD external store
07-01 16:00:13 D/RunUtil: Running command null without timeout.
07-01 16:00:13 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:00:13 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:00:13 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:00:13 D/RunUtil: Running command null without timeout.
07-01 16:00:13 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:00:13 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:00:13 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:00:14 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:00:14 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:00:14 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:00:14 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_14986947879900973043.txt] without timeout.
07-01 16:00:14 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:00:14 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:00:14 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:00:14 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:00:40 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:00:41 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:00:41 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3593.000000
07-01 16:00:41 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 933.000000
07-01 16:00:41 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 26.000000
07-01 16:00:41 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 529.000000
07-01 16:00:41 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 17.000000
07-01 16:00:41 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 21.000000
07-01 16:00:41 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 4.000000
07-01 16:00:41 D/ProtoMeasurementBase: searchbar_state_loading: size = 2, sum: 344.000000
07-01 16:00:41 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2922.000000
07-01 16:00:41 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 172.000000
07-01 16:00:41 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 318.000000
07-01 16:00:41 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2622.000000
07-01 16:00:41 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 940.000000
07-01 16:00:41 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 91.000000
07-01 16:00:41 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 82.000000
07-01 16:00:41 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 29.000000
07-01 16:00:41 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 938.000000
07-01 16:00:41 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 120.000000
07-01 16:00:41 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 318.000000
07-01 16:00:41 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:00:41 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 51.000000
07-01 16:00:41 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:00:41 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:00:41 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3593.000000
07-01 16:00:41 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 933.000000
07-01 16:00:41 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 26.000000
07-01 16:00:41 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 529.000000
07-01 16:00:41 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 17.000000
07-01 16:00:41 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 21.000000
07-01 16:00:41 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 4.000000
07-01 16:00:41 D/ProtoMeasurementBase: searchbar_state_loading: size = 2, sum: 344.000000
07-01 16:00:41 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2922.000000
07-01 16:00:41 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 172.000000
07-01 16:00:41 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 318.000000
07-01 16:00:41 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2622.000000
07-01 16:00:41 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:00:41 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 940.000000
07-01 16:00:41 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:00:41 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 91.000000
07-01 16:00:41 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 82.000000
07-01 16:00:41 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 29.000000
07-01 16:00:41 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 938.000000
07-01 16:00:41 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 120.000000
07-01 16:00:41 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 318.000000
07-01 16:00:41 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 51.000000
07-01 16:00:41 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:00:41 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:00:41 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3593.000000
07-01 16:00:41 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 933.000000
07-01 16:00:41 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 26.000000
07-01 16:00:41 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 529.000000
07-01 16:00:41 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 17.000000
07-01 16:00:41 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 21.000000
07-01 16:00:41 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 4.000000
07-01 16:00:41 D/ProtoMeasurementBase: searchbar_state_loading: size = 2, sum: 344.000000
07-01 16:00:41 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2922.000000
07-01 16:00:41 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 172.000000
07-01 16:00:41 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 318.000000
07-01 16:00:41 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2622.000000
07-01 16:00:41 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 940.000000
07-01 16:00:41 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:00:41 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 91.000000
07-01 16:00:41 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 82.000000
07-01 16:00:41 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 29.000000
07-01 16:00:41 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 938.000000
07-01 16:00:41 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 120.000000
07-01 16:00:41 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 318.000000
07-01 16:00:41 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 51.000000
07-01 16:00:41 I/MeasurementPrinter:                   318.0	                OK	                 3562.0	                OK	                            940.0	                OK
07-01 16:00:41 D/RunUtil: Running command null without timeout.
07-01 16:00:41 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:00:42 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:00:42 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:00:42 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:00:42 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:00:42 D/RunUtil: Running command null without timeout.
07-01 16:00:42 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:00:42 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:00:42 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:00:42 D/RunUtil: Running command null without timeout.
07-01 16:00:42 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:00:42 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:00:42 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 16:00:42 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 16:00:43 I/DeviceStateMonitor: Waiting 359959 ms for device SKBFXYWR0LWD package manager
07-01 16:00:43 I/NativeDeviceStateMonitor: Waiting 359872 ms for device SKBFXYWR0LWD external store
07-01 16:00:44 D/RunUtil: Running command null without timeout.
07-01 16:00:44 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:00:44 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:00:44 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:00:44 D/RunUtil: Running command null without timeout.
07-01 16:00:44 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:00:44 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:00:44 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:00:44 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:00:44 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:00:45 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:00:45 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_3752948514354200611.txt] without timeout.
07-01 16:00:45 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:00:45 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:00:45 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:00:45 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:01:10 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:01:10 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:01:10 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 2818.000000
07-01 16:01:10 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 778.000000
07-01 16:01:10 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 12.000000
07-01 16:01:10 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 474.000000
07-01 16:01:10 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 46.000000
07-01 16:01:10 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 18.000000
07-01 16:01:10 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 30.000000
07-01 16:01:10 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 94.000000
07-01 16:01:10 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2117.000000
07-01 16:01:10 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 28.000000
07-01 16:01:10 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 147.000000
07-01 16:01:10 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2017.000000
07-01 16:01:10 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 785.000000
07-01 16:01:10 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 19.000000
07-01 16:01:10 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 9.000000
07-01 16:01:10 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 3.000000
07-01 16:01:10 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 779.000000
07-01 16:01:10 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 71.000000
07-01 16:01:10 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 147.000000
07-01 16:01:10 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:01:10 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 18.000000
07-01 16:01:10 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:01:11 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:01:11 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 2818.000000
07-01 16:01:11 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 778.000000
07-01 16:01:11 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 12.000000
07-01 16:01:11 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 474.000000
07-01 16:01:11 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 46.000000
07-01 16:01:11 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 18.000000
07-01 16:01:11 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 30.000000
07-01 16:01:11 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 94.000000
07-01 16:01:11 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2117.000000
07-01 16:01:11 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 28.000000
07-01 16:01:11 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 147.000000
07-01 16:01:11 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2017.000000
07-01 16:01:11 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:01:11 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 785.000000
07-01 16:01:11 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:01:11 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 19.000000
07-01 16:01:11 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 9.000000
07-01 16:01:11 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 3.000000
07-01 16:01:11 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 779.000000
07-01 16:01:11 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 71.000000
07-01 16:01:11 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 147.000000
07-01 16:01:11 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 18.000000
07-01 16:01:11 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:01:11 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:01:11 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 2818.000000
07-01 16:01:11 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 778.000000
07-01 16:01:11 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 12.000000
07-01 16:01:11 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 474.000000
07-01 16:01:11 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 46.000000
07-01 16:01:11 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 18.000000
07-01 16:01:11 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 30.000000
07-01 16:01:11 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 94.000000
07-01 16:01:11 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2117.000000
07-01 16:01:11 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 28.000000
07-01 16:01:11 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 147.000000
07-01 16:01:11 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2017.000000
07-01 16:01:11 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 785.000000
07-01 16:01:11 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:01:11 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 19.000000
07-01 16:01:11 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 9.000000
07-01 16:01:11 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 3.000000
07-01 16:01:11 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 779.000000
07-01 16:01:11 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 71.000000
07-01 16:01:11 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 147.000000
07-01 16:01:11 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 18.000000
07-01 16:01:11 I/MeasurementPrinter:                   147.0	                OK	                 2802.0	                OK	                            785.0	                OK
07-01 16:01:11 D/RunUtil: Running command null without timeout.
07-01 16:01:11 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:01:11 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:01:11 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:01:11 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:01:12 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:01:12 D/RunUtil: Running command null without timeout.
07-01 16:01:12 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:01:12 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:01:12 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:01:12 D/RunUtil: Running command null without timeout.
07-01 16:01:12 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:01:12 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:01:12 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 16:01:12 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 16:01:12 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYWR0LWD package manager
07-01 16:01:12 I/NativeDeviceStateMonitor: Waiting 359903 ms for device SKBFXYWR0LWD external store
07-01 16:01:13 D/RunUtil: Running command null without timeout.
07-01 16:01:13 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:01:14 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:01:14 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:01:14 D/RunUtil: Running command null without timeout.
07-01 16:01:14 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:01:14 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:01:14 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:01:14 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:01:14 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:01:14 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:01:14 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_8931062867338680255.txt] without timeout.
07-01 16:01:15 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:01:15 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:01:15 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:01:15 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:01:41 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:01:41 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:01:41 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3354.000000
07-01 16:01:41 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 824.000000
07-01 16:01:41 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 5.000000
07-01 16:01:41 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 364.000000
07-01 16:01:41 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 10.000000
07-01 16:01:41 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:01:41 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 16:01:41 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 273.000000
07-01 16:01:41 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2557.000000
07-01 16:01:41 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 42.000000
07-01 16:01:41 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 132.000000
07-01 16:01:41 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2504.000000
07-01 16:01:41 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 831.000000
07-01 16:01:41 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 39.000000
07-01 16:01:41 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 3.000000
07-01 16:01:41 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 16.000000
07-01 16:01:41 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 825.000000
07-01 16:01:41 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 86.000000
07-01 16:01:41 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 134.000000
07-01 16:01:41 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:01:41 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 14.000000
07-01 16:01:41 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:01:41 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:01:41 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3354.000000
07-01 16:01:41 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 824.000000
07-01 16:01:41 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 5.000000
07-01 16:01:41 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 364.000000
07-01 16:01:41 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 10.000000
07-01 16:01:41 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:01:41 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 16:01:41 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 273.000000
07-01 16:01:41 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2557.000000
07-01 16:01:41 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 42.000000
07-01 16:01:41 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 132.000000
07-01 16:01:41 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2504.000000
07-01 16:01:41 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:01:41 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 831.000000
07-01 16:01:41 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:01:41 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 39.000000
07-01 16:01:41 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 3.000000
07-01 16:01:41 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 16.000000
07-01 16:01:41 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 825.000000
07-01 16:01:41 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 86.000000
07-01 16:01:41 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 134.000000
07-01 16:01:41 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 14.000000
07-01 16:01:41 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:01:41 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:01:41 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3354.000000
07-01 16:01:41 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 824.000000
07-01 16:01:41 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 5.000000
07-01 16:01:41 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 364.000000
07-01 16:01:41 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 10.000000
07-01 16:01:41 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:01:41 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 16:01:41 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 273.000000
07-01 16:01:41 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2557.000000
07-01 16:01:41 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 42.000000
07-01 16:01:41 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 132.000000
07-01 16:01:41 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2504.000000
07-01 16:01:41 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 831.000000
07-01 16:01:41 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:01:41 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 39.000000
07-01 16:01:41 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 3.000000
07-01 16:01:41 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 16.000000
07-01 16:01:41 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 825.000000
07-01 16:01:41 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 86.000000
07-01 16:01:41 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 134.000000
07-01 16:01:41 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 14.000000
07-01 16:01:41 I/MeasurementPrinter:                   134.0	                OK	                 3335.0	                OK	                            831.0	                OK
07-01 16:01:41 D/RunUtil: Running command null without timeout.
07-01 16:01:41 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:01:42 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:01:42 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:01:42 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:01:42 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:01:42 D/RunUtil: Running command null without timeout.
07-01 16:01:42 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:01:43 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:01:43 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:01:43 D/RunUtil: Running command null without timeout.
07-01 16:01:43 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:01:43 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:01:43 I/NativeDeviceStateMonitor: Device SKBFXYWR0LWD is already ONLINE
07-01 16:01:43 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYWR0LWD boot complete
07-01 16:01:43 I/DeviceStateMonitor: Waiting 359959 ms for device SKBFXYWR0LWD package manager
07-01 16:01:43 I/NativeDeviceStateMonitor: Waiting 359897 ms for device SKBFXYWR0LWD external store
07-01 16:01:44 D/RunUtil: Running command null without timeout.
07-01 16:01:44 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:01:44 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:01:44 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:01:44 D/RunUtil: Running command null without timeout.
07-01 16:01:44 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:01:44 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:01:44 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:01:45 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:01:45 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:01:45 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:01:45 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/e6359ee9-b86f-4f25-9ef6-5e9566226514/tmp/katniss-primes-2024-06-30T23_51_54.237_6704115456444574039.txt] without timeout.
07-01 16:01:45 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:01:45 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:01:45 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:01:45 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:02:11 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:02:12 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:02:12 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3579.000000
07-01 16:02:12 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 1041.000000
07-01 16:02:12 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 2.000000
07-01 16:02:12 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 495.000000
07-01 16:02:12 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 83.000000
07-01 16:02:12 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 12.000000
07-01 16:02:12 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 11.000000
07-01 16:02:12 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 264.000000
07-01 16:02:12 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2653.000000
07-01 16:02:12 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 63.000000
07-01 16:02:12 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 169.000000
07-01 16:02:12 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2529.000000
07-01 16:02:12 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 1057.000000
07-01 16:02:12 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 58.000000
07-01 16:02:12 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 5.000000
07-01 16:02:12 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 21.000000
07-01 16:02:12 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 1041.000000
07-01 16:02:12 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 83.000000
07-01 16:02:12 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 170.000000
07-01 16:02:12 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:02:12 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 52.000000
07-01 16:02:12 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:02:12 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:02:12 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3579.000000
07-01 16:02:12 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 1041.000000
07-01 16:02:12 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 2.000000
07-01 16:02:12 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 495.000000
07-01 16:02:12 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 83.000000
07-01 16:02:12 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 12.000000
07-01 16:02:12 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 11.000000
07-01 16:02:12 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 264.000000
07-01 16:02:12 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2653.000000
07-01 16:02:12 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 63.000000
07-01 16:02:12 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 169.000000
07-01 16:02:12 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2529.000000
07-01 16:02:12 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:02:12 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 1057.000000
07-01 16:02:12 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:02:12 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 58.000000
07-01 16:02:12 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 5.000000
07-01 16:02:12 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 21.000000
07-01 16:02:12 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 1041.000000
07-01 16:02:12 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 83.000000
07-01 16:02:12 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 170.000000
07-01 16:02:12 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 52.000000
07-01 16:02:12 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:02:12 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:02:12 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3579.000000
07-01 16:02:12 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 1041.000000
07-01 16:02:12 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 2.000000
07-01 16:02:12 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 495.000000
07-01 16:02:12 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 83.000000
07-01 16:02:12 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 12.000000
07-01 16:02:12 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 11.000000
07-01 16:02:12 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 264.000000
07-01 16:02:12 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2653.000000
07-01 16:02:12 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 63.000000
07-01 16:02:12 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 169.000000
07-01 16:02:12 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2529.000000
07-01 16:02:12 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 1057.000000
07-01 16:02:12 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:02:12 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 58.000000
07-01 16:02:12 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 5.000000
07-01 16:02:12 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 21.000000
07-01 16:02:12 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 1041.000000
07-01 16:02:12 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 83.000000
07-01 16:02:12 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 170.000000
07-01 16:02:12 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 52.000000
07-01 16:02:12 I/MeasurementPrinter:                   170.0	                OK	                 3586.0	                OK	                           1057.0	                OK
07-01 16:02:12 D/RunUtil: Running command null without timeout.
07-01 16:02:12 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:02:13 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:02:13 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:02:13 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:02:13 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:02:13 D/RunUtil: Running command null without timeout.
07-01 16:02:13 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:02:13 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:02:13 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:02:13 D/RunUtil: Running command null without timeout.
07-01 16:02:14 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:02:14 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:02:14 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.katniss
07-01 16:02:14 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, am, force-stop, com.google.android.katniss] with timeout: 2m 0s
07-01 16:02:14 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:02:14 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:02:15 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:02:15 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:02:15 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, getprop, ro.config.low_ram] with timeout: 45s
07-01 16:02:15 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, pm, list, features] with timeout: 2m 0s
07-01 16:02:15 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYWR0LWD
07-01 16:02:15 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, getprop, ro.config.low_ram] with timeout: 45s
07-01 16:02:15 D/RunUtil: Running command [adb, -s, SKBFXYWR0LWD, shell, pm, list, features] with timeout: 2m 0s
07-01 16:02:15 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYWR0LWD
07-01 16:02:15 I/BasicAggregationTypeBase: Checking if collection is initialized with data
07-01 16:02:15 I/BasicAggregationTypeBase: Checking if collection is initialized with data
07-01 16:02:15 D/PrettyTestEventLogger: 
==================== com.google.android.assistant.v4.tvts.TvtsAssistant4Test#testHeavyDeviceUsage ENDED: Mon Jul 01 16:02:15 KST 2024 ====================
